[03/08 08:37:52,Telerik.TestStudio.Desktop.exe(6220:1),TestStudio] ElementsProvider.MergeElements() : EXCEPTION! (see below)
     Outer Exception Type: System.ArgumentOutOfRangeException
     Message: Index must be within the bounds of the List.
          Parameter name: index
     HRESULT: 0x80131502 (Official ID (if app.) = COR_E_ARGUMENTOUTOFRANGE, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5378)
     Call Stack:
          at System.ThrowHelper.ThrowArgumentOutOfRangeException(ExceptionArgument argument, ExceptionResource resource)
          at System.Collections.ObjectModel.Collection`1.Insert(Int32 index, T item)
          at ArtOfTest.WebAii.Design.ProjectModel.LightElementNode.Children_NodeAdded(Object sender, ElementNodeEventArgs e)
          at ArtOfTest.Common.Design.ProjectModel.ElementNodeCollection.OnNodeAdded(ElementNode node)
          at ArtOfTest.Common.Design.ProjectModel.ElementNode.AddChild(ElementNode node)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.MergeOrAddNode(ElementNode root, ElementNode node, Boolean compareBothSides)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.MergeNodesRecursively(ElementNode root, ElementNode node, ITargetElement target, Boolean compareBothSides)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.Merge(ElementNode branch, Boolean compareBothSides)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.MergeElements(IEnumerable`1 descriptors)

[03/08 08:38:03,Telerik.TestStudio.Desktop.exe(6220:11),TestStudio] <>c__DisplayClass1d.<ReplaceExtractedElements>b__1c() : EXCEPTION! (see below)
     Outer Exception Type: System.NullReferenceException
     Message: Object reference not set to an instance of an object.
     HRESULT: 0x80004003 (Official ID (if app.) = E_POINTER, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16387)
     Call Stack:
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsInternal(TestBase test)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.<>c__DisplayClass1d.<ReplaceExtractedElements>b__1c()

[03/08 08:38:06,Telerik.TestStudio.Desktop.exe(6220:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'WebTest Copy' selected.
[03/08 08:38:09,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:10,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:12,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:43,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:44,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:44,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:44,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:44,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:45,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:38:54,ArtOfTest.Runner.exe(8580:3)] First trace message from process 8580: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=2020
[03/08 08:38:54,ArtOfTest.Runner.exe(8580:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 5748).
[03/08 08:38:54,ArtOfTest.Runner.exe(8580:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/08 08:38:55,ArtOfTest.Runner.exe(8580:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/08 08:38:55,ArtOfTest.Runner.exe(8580:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 7240).
[03/08 08:38:55,ArtOfTest.Runner.exe(8580:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=8864, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[03/08 08:38:58,ArtOfTest.Runner.exe(8580:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=328776)...
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:5),Error] TestExecuteProxy.ExecuteTest() : EXCEPTION! (see below)
     Outer Exception Type: System.ApplicationException
     Message: Exception thrown attempting to launch Internet Explorer. Please make sure Internet Explorer is properly installed and you are able to launch it.
     HRESULT: 0x80131600 (Official ID (if app.) = COR_E_APPLICATION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5632)
     Call Stack:
          at ArtOfTest.WebAii.BrowserSpecialized.InternetExplorer.InternetExplorerActions.LaunchNewBrowserInstance(Int32 timeout, ProcessWindowStyle windowStyle, String pipename)
          at ArtOfTest.WebAii.Core.Manager.LaunchNewBrowser(BrowserType browserToLaunch, Boolean waitForBrowserToConnect, ProcessWindowStyle windowStyle, String arguments)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.InitializeWeb(ExecutionEngineCreateParams initParams)
          at ArtOfTest.WebAii.Design.Execution.TestExecuteProxy.CreateAndInitializeEngine(Test test, ExecutionEngineCreateParams cp)
          at ArtOfTest.WebAii.Design.Execution.TestExecuteProxy.ExecuteTest(ExecuteTestCommand command)

     Inner Exception Type: System.TimeoutException
     Message: Wait for condition has timed out
     HRESULT: 0x80131505 (Official ID (if app.) = COR_E_TIMEOUT, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5381)
     Call Stack:
          at ArtOfTest.Common.WaitSync.CheckResult(WaitSync wait, String extraExceptionInfo, Object target)
          at ArtOfTest.Common.WaitSync.For[T](Predicate`1 predicate, T target, Boolean invertCondition, Int32 timeout, WaitResultType errorResultType)
          at ArtOfTest.Common.WaitSync.For[T](Predicate`1 predicate, T target, Boolean invertCondition, Int32 timeout)
          at ArtOfTest.Common.WaitSync.For[T](Predicate`1 predicate, T target, Int32 timeout)
          at ArtOfTest.Common.Win32.Connector.Attach(IntPtr& handle, Boolean findTabWindow, Int32 waitTimeout)
          at ArtOfTest.WebAii.BrowserSpecialized.InternetExplorer.InternetExplorerActions.LaunchNewBrowserInstance(Int32 timeout, ProcessWindowStyle windowStyle, String pipename)

[03/08 08:39:03,ArtOfTest.Runner.exe(8580:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1564719388" in order to unblock and eventually terminate it...
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 8404).
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[03/08 08:39:03,ArtOfTest.Runner.exe(8580:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:48,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:49,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:39:49,ArtOfTest.Runner.exe(8648:3)] First trace message from process 8648: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=3580
[03/08 08:39:49,ArtOfTest.Runner.exe(8648:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 3460).
[03/08 08:39:49,ArtOfTest.Runner.exe(8648:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/08 08:39:50,ArtOfTest.Runner.exe(8648:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/08 08:39:50,ArtOfTest.Runner.exe(8648:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 8020).
[03/08 08:39:50,ArtOfTest.Runner.exe(8648:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=4716, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=66896)...
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] Connector.Start() : Process launched (ID=4528, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 66998").
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] Connector.Start() : Process exited (ID=4528, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 66998").
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.Desktop" (ID=6220) is assumed to host the controller.
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] Connector.InjectCode() : ------- Function Start -------
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1314.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?6220
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 66998 on another thread...
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:10)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 10, native ID = 6308).
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:10),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/08 08:39:51,ArtOfTest.Runner.exe(8648:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 66998 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1314.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?6220")...
[03/08 08:39:52,IEXPLORE.EXE(8304:1)] First trace message from process 8304: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:4716 CREDAT:275457 /prefetch:2
[03/08 08:39:52,IEXPLORE.EXE(8304:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 8396).
[03/08 08:39:52,IEXPLORE.EXE(8304:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 66998, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_2108815581", PID = 6220).
[03/08 08:39:52,IEXPLORE.EXE(8304:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 66998 on same thread...
[03/08 08:39:52,IEXPLORE.EXE(8304:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/08 08:39:52,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_608189d0-916b-494f-a8d3-39fb27b350c0".
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:7)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 7, native ID = 9108).
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:7),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 6360).
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[03/08 08:39:52,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "57247bb1-20f7-4519-b434-c2a3bf3e8216.CommandPipe", Async = "57247bb1-20f7-4519-b434-c2a3bf3e8216.AsyncPipe"}
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:7),Framework] Manager.AddNewBrowser() : Added client ID "Client_608189d0-916b-494f-a8d3-39fb27b350c0" (now there is/are 1 total).
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Framework] Connector.InjectCode() : ------- Function End -------
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'WebTest Copy', path: 'WebTest Copy.tstest.'
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 10 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[03/08 08:39:52,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ProductGuide.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[03/08 08:39:53,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://floridaswater.com/'"...
[03/08 08:40:00,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://floridaswater.com/'".
[03/08 08:40:00,ArtOfTest.Runner.exe(8648:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[03/08 08:40:00,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'PermittingLink'"...
[03/08 08:40:01,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'PermittingLink'".
[03/08 08:40:01,ArtOfTest.Runner.exe(8648:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[03/08 08:40:02,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Verify 'ApplicationPerLink' is enabled"...
[03/08 08:40:02,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Verify 'ApplicationPerLink' is enabled".
[03/08 08:40:02,ArtOfTest.Runner.exe(8648:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[03/08 08:40:02,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'ApplicationPerLink'"...
[03/08 08:40:02,ArtOfTest.Runner.exe(8648:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'ApplicationPerLink'".
[03/08 08:40:02,Telerik.TestStudio.Desktop.exe(6220:14)] First trace message from background thread "Callback Listener Thread" (managed ID = 14, native ID = 8652).
[03/08 08:40:02,Telerik.TestStudio.Desktop.exe(6220:14),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12)] First trace message from background thread "Web Recorder Dispatcher" (managed ID = 12, native ID = 168).
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.Start() : Process launched (ID=3480, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 66998").
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.Start() : Process exited (ID=3480, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 66998").
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : ------- Function Start -------
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1314.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?6220
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 66998 on another thread...
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:24)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 24, native ID = 1156).
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:24),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 66998 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1314.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?6220")...
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 66998, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_287868073", PID = 6220).
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 66998 on same thread...
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] ArtOfTestPluginEntryPoint.Connect() : Plugin instance already exists for HWND 66998; shutting it down before creating a new one to replace it.
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_608189d0-916b-494f-a8d3-39fb27b350c0") to pipe server...
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Warning] BrowserRemoteClient.Disconnect() : Command pipe broke.
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[03/08 08:40:03,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_4c7e2676-dee0-4743-a57a-93a49595045e".
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:10)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 10, native ID = 4936).
[03/08 08:40:03,Telerik.TestStudio.Desktop.exe(6220:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[03/08 08:40:04,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "21ef8456-c6a8-48dd-977a-39e7fb429f5f.CommandPipe", Async = "21ef8456-c6a8-48dd-977a-39e7fb429f5f.AsyncPipe"}
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:25)] First trace message from background thread "Remoted async command listener" (managed ID = 25, native ID = 7956).
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:25),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:25),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:25),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:10),Framework] Manager.AddNewBrowser() : Added client ID "Client_4c7e2676-dee0-4743-a57a-93a49595045e" (now there is/are 1 total).
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : ------- Function End -------
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : ------- Function Start -------
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.WebAii.Design.RemoteRecorder\v4.0_2015.3.1314.0__56fa9668d21d4722\ArtOfTest.WebAii.Design.RemoteRecorder.dll?ArtOfTest.WebAii.Design.RemoteRecorder.RemotedRecorderEntryPoint?6220
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 66998 on another thread...
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:24)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 24, native ID = 1156).
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:24),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/08 08:40:04,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 66998 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.WebAii.Design.RemoteRecorder\v4.0_2015.3.1314.0__56fa9668d21d4722\ArtOfTest.WebAii.Design.RemoteRecorder.dll?ArtOfTest.WebAii.Design.RemoteRecorder.RemotedRecorderEntryPoint?6220")...
[03/08 08:40:04,IEXPLORE.EXE(8304:1)] First trace message from background thread "RemotedRecorderEntryPoint entry point thread" (managed ID = 1, native ID = 8396).
[03/08 08:40:04,IEXPLORE.EXE(8304:1),Recording] RemotedRecorderEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString "66998:Pipe.ArtOfTest.WebAii.BrowserProvisioner_287868073:6220" as Remoted).
[03/08 08:40:05,IEXPLORE.EXE(8304:8)] First trace message from background thread "ConnectingSurfaceThread For HWND 66998" (managed ID = 8, native ID = 6320).
[03/08 08:40:05,IEXPLORE.EXE(8304:8),Recording] <>c__DisplayClass4.<CreateConnectingSurface>b__0() : ConnectingSurface displayed upon parent HWND 66998.
[03/08 08:40:05,IEXPLORE.EXE(8304:1),Recording] RemoteRecorder constructor : Set NetNamedPipeBinding successful.
[03/08 08:40:05,IEXPLORE.EXE(8304:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 66998 on same thread...
[03/08 08:40:06,IEXPLORE.EXE(8304:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[03/08 08:40:06,IEXPLORE.EXE(8304:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/08 08:40:06,IEXPLORE.EXE(8304:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/08 08:40:06,IEXPLORE.EXE(8304:1),Recording] RemoteRecorder.SetSite() : Successfully requested mime filter registration
[03/08 08:40:10,IEXPLORE.EXE(8304:1),Recording] IERecordingSurface.InitializeDocument() : Document complete for https://permitting.sjrwmd.com/epermitting/jsp/Search.do?theAction=PermitNumSearch.
[03/08 08:40:11,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[03/08 08:40:11,Telerik.TestStudio.Desktop.exe(6220:12),Framework] Connector.InjectCode() : ------- Function End -------
[03/08 08:40:11,IEXPLORE.EXE(8304:1),TestStudio] InternetExplorerHtmlRecorderHostEventCallbacks.ShowRecorderWindow() : RemoteRecorder.ShowRecorderWindow called from Host
[03/08 08:40:12,IEXPLORE.EXE(8304:1),TestStudio] InternetExplorerHtmlRecorderHostEventCallbacks.ToggleRecording() : RemoteRecorder.ToggleRecording called from Host
[03/08 08:40:12,IEXPLORE.EXE(8304:1),Recording] IERecordingSurface.InitializeDocument() : Document complete for https://permitting.sjrwmd.com/epermitting/jsp/Search.do?theAction=PermitNumSearch.
[03/08 08:40:17,IEXPLORE.EXE(8304:16)] First trace message from background thread "TranslatorsMatchThread" (managed ID = 16, native ID = 6416).
[03/08 08:40:17,IEXPLORE.EXE(8304:16),Recording] TranslatorsCache.UpdateCache() : Building Translator Cache for 'Page (Search Application)'.
[03/08 08:40:17,IEXPLORE.EXE(8304:16),Recording] TranslatorsCache.UpdateCache() : Caching took '132' msec.
[03/08 08:40:22,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : value=1,tagname=input 
[03/08 08:40:27,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : value=1,tagname=input 
[03/08 08:40:30,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : value=permitted,tagname=input 
[03/08 08:40:34,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : id=receiveDt,tagname=input 
[03/08 08:40:41,IEXPLORE.EXE(8304:17)] First trace message from pool unnamed thread (managed ID = 17, native ID = 1664).
[03/08 08:40:41,IEXPLORE.EXE(8304:17),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : id=fromDt,tagname=input 
[03/08 08:40:47,IEXPLORE.EXE(8304:19)] First trace message from pool unnamed thread (managed ID = 19, native ID = 8524).
[03/08 08:40:47,IEXPLORE.EXE(8304:19),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : id=toDt,tagname=input 
[03/08 08:40:50,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : value=Submit,tagname=input 
[03/08 08:40:51,IEXPLORE.EXE(8304:1),Recording] IERecordingSurface.InitializeDocument() : Document complete for https://permitting.sjrwmd.com/epermitting/jsp/Search.do.
[03/08 08:40:56,IEXPLORE.EXE(8304:16),Recording] TranslatorsCache.UpdateCache() : Building Translator Cache for 'Page (Search Applications)'.
[03/08 08:40:56,IEXPLORE.EXE(8304:16),Recording] TranslatorsCache.UpdateCache() : Caching took '73' msec.
[03/08 08:41:05,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : href=Search.do?theAction=searchDetail&amp;permitNumber=145298,tagname=a 
[03/08 08:41:10,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : href=Search.do?theAction=searchDetail&amp;permitNumber=145184,tagname=a 
[03/08 08:41:14,IEXPLORE.EXE(8304:1),Recording] ElementsGenerator.CreateHtmlElementDescriptor() : Generated Html FindExpr : href=Search.do?theAction=searchDetail&amp;permitNumber=145168,tagname=a 
[03/08 08:41:20,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_4c7e2676-dee0-4743-a57a-93a49595045e") to pipe server...
[03/08 08:41:20,Telerik.TestStudio.Desktop.exe(6220:25),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_4c7e2676-dee0-4743-a57a-93a49595045e") has been received from the pipe client.
[03/08 08:41:20,IEXPLORE.EXE(8304:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[03/08 08:41:20,Telerik.TestStudio.Desktop.exe(6220:25),Framework] Manager.RemoveBrowser() : Removed client ID "Client_4c7e2676-dee0-4743-a57a-93a49595045e" (0 remaining).
[03/08 08:41:20,Telerik.TestStudio.Desktop.exe(6220:25),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[03/08 08:41:20,Telerik.TestStudio.Desktop.exe(6220:25),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 25).
[03/08 08:41:42,Telerik.TestStudio.Desktop.exe(6220:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'WebTest' selected.
[03/08 08:41:42,Telerik.TestStudio.Desktop.exe(6220:11),TestStudio] <>c__DisplayClass1d.<ReplaceExtractedElements>b__1c() : EXCEPTION! (see below)
     Outer Exception Type: System.NullReferenceException
     Message: Object reference not set to an instance of an object.
     HRESULT: 0x80004003 (Official ID (if app.) = E_POINTER, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16387)
     Call Stack:
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsInternal(TestBase test)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.<>c__DisplayClass1d.<ReplaceExtractedElements>b__1c()

[03/08 08:43:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:43:14,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:43:15,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:43:17,Telerik.TestStudio.Desktop.exe(6220:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'WebTest Copy' selected.
[03/08 08:43:20,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:43:20,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:43:21,Telerik.TestStudio.Desktop.exe(6220:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[03/08 08:43:36,Telerik.TestStudio.Desktop.exe(6220:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'WebTest' selected.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_287868073" in order to unblock and eventually terminate it...
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1349949648" in order to unblock and eventually terminate it...
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:13)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 13, native ID = 4000).
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:13),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[03/08 08:43:40,Telerik.TestStudio.Desktop.exe(6220:1),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[03/08 08:57:52,Telerik.TestStudio.Desktop.exe(2116:1)] First trace message from process 2116: "C:\Program Files (x86)\Telerik\Test Studio\Bin\Telerik.TestStudio.Desktop.exe" False "I:\Application Development Team\TestStudio\briar-tests\SJ Regression" /trial
[03/08 08:57:52,Telerik.TestStudio.Desktop.exe(2116:1)] First trace message from foreground thread "Application start thread" (managed ID = 1, native ID = 4300).
[03/08 08:57:52,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] App.OnStartup() : 
     Using: Telerik.TestStudio.Core, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=4fd5f65be123776c
     OS: Microsoft Windows 7 Professional 64-bit 6.1.7601 Service Pack 1
     .NET version: 4.0.30319.34209
[03/08 08:57:53,Telerik.TestStudio.Scheduling.Client.exe(5096:1)] First trace message from process 5096: "C:\Program Files (x86)\Telerik\Test Studio\Bin\Telerik.TestStudio.Scheduling.Client.exe" /EmbedExecutionManager /EmbedProcessManager
[03/08 08:57:53,Telerik.TestStudio.Scheduling.Client.exe(5096:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 5624).
[03/08 08:57:53,Telerik.TestStudio.Scheduling.Client.exe(5096:1),Execution] ServiceConfiguration.SetupConfig() : Config file found.
[03/08 08:57:53,Telerik.TestStudio.Scheduling.Client.exe(5096:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/08 08:57:58,Telerik.TestStudio.Scheduling.Client.exe(5096:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/08 08:58:02,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Load.LoadPkg implements ITestStudioPkg and was added as a package.
[03/08 08:58:02,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Manual.ManualPkg implements ITestStudioPkg and was added as a package.
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Web.WebPkg implements ITestStudioPkg and was added as a package.
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator.LoadPackages() : Telerik.TestStudio.Wpf.WpfPkg implements ITestStudioPkg and was added as a package.
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Jira.BugTracking, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=83079a39d63be0d8
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Load, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=85d0e578a53d02ec
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Manual, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=06f62486e897f63c
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.OTA.Interop, Version=1.0.0.0, Culture=neutral, PublicKeyToken=308294b5ce7f3552
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Plugins, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=308294b5ce7f3552
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.ProductGuide, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=971283d0ca522fd6
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Shared, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=31ff7e2875e36c6c
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.TeamPulse, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=308294b5ce7f3552
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Web, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=a481bc80aec6669c
[03/08 08:58:03,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] Utils.EnumPluginTypes() : Duplicate assemblies encountered while enumerating plugin types: Telerik.TestStudio.Wpf, Version=2015.3.1314.0, Culture=neutral, PublicKeyToken=91731613e21d2b88
[03/08 08:58:09,Telerik.TestStudio.Desktop.exe(2116:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 1308).
[03/08 08:58:09,Telerik.TestStudio.Desktop.exe(2116:4),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/08 08:58:09,Telerik.TestStudio.Desktop.exe(2116:1),Performance] PerformanceProfileViewModel.set_ViewingTimeRange() : Viewing time range overwritten: 3/8/2016 12:58:09 PM to 3/8/2016 12:58:09 PM.
[03/08 08:58:09,Telerik.TestStudio.Desktop.exe(2116:1),Performance] PerformanceProfileViewModel.set_ViewingTimeRange() : Viewing time range overwritten: 3/8/2016 12:58:09 PM to 3/8/2016 12:58:09 PM.
[03/08 08:58:10,Telerik.TestStudio.Desktop.exe(2116:4),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/08 08:58:10,Telerik.TestStudio.Desktop.exe(2116:6)] First trace message from pool unnamed thread (managed ID = 6, native ID = 6608).
[03/08 08:58:10,Telerik.TestStudio.Desktop.exe(2116:6),Recording] RecorderHostService constructor : Host endpoint: net.pipe://localhost/Instance/51328/DesignCanvas/2116
[03/08 08:58:12,Telerik.TestStudio.Scheduling.Client.exe(5096:1),Execution] PostListExecutionEmailer.CheckForValidSmtpSettings() : Unable to send email due to uninitialized SMTP settings
[03/08 08:58:12,Telerik.TestStudio.Desktop.exe(2116:1),TestStudio] MainWindow.MainWindow_Loaded() : Test Studio (version:2015.3.1314.0) started.
[03/08 08:58:15,Telerik.TestStudio.Desktop.exe(2116:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 2968).
[03/08 08:58:15,Telerik.TestStudio.Desktop.exe(2116:5),Uncategorized] BugTrackersLoader.LoadSystems() : ------- Function Start -------
[03/08 08:58:16,Telerik.TestStudio.Scheduling.Client.exe(5096:1),Execution] ProcessManager.StartProcess() : Starting profiler
[03/08 08:58:16,Telerik.TestStudio.Scheduling.Client.exe(5096:1),Execution] ProcessManager.CreateNativeProcess() : Started profiler:6168 url= http://dell90393.sjrwmd.com:8031/
[03/08 08:58:16,Telerik.TestStudio.Scheduling.Client.exe(5096:1),Execution] ProcessManager.StartProcess() : Starting executor
[03/08 08:58:16,Telerik.TestStudio.Scheduling.Client.exe(5096:1),Execution] ProcessManager.CreateNativeProcess() : Started executor:5204 url= http://dell90393.sjrwmd.com:8032/
[03/08 08:58:17,Telerik.TestStudio.Profiler.exe(6168:1)] First trace message from process 6168: "Telerik.TestStudio.Profiler.exe" "http://dell90393.sjrwmd.com:8031/" ""
[03/08 08:58:17,Telerik.TestStudio.Profiler.exe(6168:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 6164).
[03/08 08:58:17,Telerik.TestStudio.Profiler.exe(6168:1),Execution] WcfHttpRawCommunicationLayer.Start() : Starting WCF Service with RawHTTP Bindings at http://dell90393.sjrwmd.com:8031/
[03/08 08:58:18,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator._iUIProject_ProjectOpened() : Project 'SJ Regression' Opened.
[03/08 08:58:18,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator.set_SelectedProject() : Active Project is : 'SJ Regression'
[03/08 08:58:19,Telerik.TestStudio.RemoteExecutor.exe(5204:1)] First trace message from process 5204: "Telerik.TestStudio.RemoteExecutor.exe" "http://dell90393.sjrwmd.com:8032/" ""
[03/08 08:58:19,Telerik.TestStudio.RemoteExecutor.exe(5204:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 4904).
[03/08 08:58:19,Telerik.TestStudio.RemoteExecutor.exe(5204:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[03/08 08:58:19,Telerik.TestStudio.RemoteExecutor.exe(5204:1),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[03/08 08:58:22,Telerik.TestStudio.Scheduling.Client.exe(5096:9)] First trace message from pool unnamed thread (managed ID = 9, native ID = 6284).
[03/08 08:58:22,Telerik.TestStudio.Scheduling.Client.exe(5096:9),Error] WcfHttpRawRequest.Send() : EXCEPTION! (see below)
     Outer Exception Type: System.Net.WebException
     Message: Unable to connect to the remote server
     HRESULT: 0x80131509 (Official ID (if app.) = COR_E_INVALIDOPERATION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5385)
     Call Stack:
          at System.Net.HttpWebRequest.GetResponse()
          at ArtOfTest.WebAii.Messaging.Http.WcfHttpRawRequest.Send()

     Inner Exception Type: System.Net.Sockets.SocketException
     Message: No connection could be made because the target machine actively refused it 10.2.81.16:8032
     HRESULT: 0x80004005 (Official ID (if app.) = E_FAIL, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16389)
     Call Stack:
          at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
          at System.Net.ServicePoint.ConnectSocketInternal(Boolean connectFailure, Socket s4, Socket s6, Socket& socket, IPAddress& address, ConnectSocketState state, IAsyncResult asyncResult, Exception& exception)

[03/08 08:58:22,Telerik.TestStudio.Scheduling.Client.exe(5096:9),Execution] <>c__DisplayClass10.<BeginPingingProcesses>b__b() : 5204 failed ping 1 times. Not dropping.
[03/08 08:58:29,Telerik.TestStudio.Desktop.exe(2116:4),TestStudio] ElementsProvider.MergeElements() : EXCEPTION! (see below)
     Outer Exception Type: System.ArgumentOutOfRangeException
     Message: Index must be within the bounds of the List.
          Parameter name: index
     HRESULT: 0x80131502 (Official ID (if app.) = COR_E_ARGUMENTOUTOFRANGE, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5378)
     Call Stack:
          at System.ThrowHelper.ThrowArgumentOutOfRangeException(ExceptionArgument argument, ExceptionResource resource)
          at System.Collections.ObjectModel.Collection`1.Insert(Int32 index, T item)
          at ArtOfTest.WebAii.Design.ProjectModel.LightElementNode.Children_NodeAdded(Object sender, ElementNodeEventArgs e)
          at ArtOfTest.Common.Design.ProjectModel.ElementNodeCollection.OnNodeAdded(ElementNode node)
          at ArtOfTest.Common.Design.ProjectModel.ElementNode.AddChild(ElementNode node)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.MergeOrAddNode(ElementNode root, ElementNode node, Boolean compareBothSides)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.MergeNodesRecursively(ElementNode root, ElementNode node, ITargetElement target, Boolean compareBothSides)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.Merge(ElementNode branch, Boolean compareBothSides)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.MergeElements(IEnumerable`1 descriptors)

[03/08 08:58:29,Telerik.TestStudio.Desktop.exe(2116:4),TestStudio] ElementsProvider.MergeElements() : EXCEPTION! (see below)
     Outer Exception Type: System.ArgumentOutOfRangeException
     Message: Index must be within the bounds of the List.
          Parameter name: index
     HRESULT: 0x80131502 (Official ID (if app.) = COR_E_ARGUMENTOUTOFRANGE, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5378)
     Call Stack:
          at System.ThrowHelper.ThrowArgumentOutOfRangeException(ExceptionArgument argument, ExceptionResource resource)
          at System.Collections.ObjectModel.Collection`1.Insert(Int32 index, T item)
          at ArtOfTest.WebAii.Design.ProjectModel.LightElementNode.Children_NodeAdded(Object sender, ElementNodeEventArgs e)
          at ArtOfTest.Common.Design.ProjectModel.ElementNodeCollection.OnNodeAdded(ElementNode node)
          at ArtOfTest.Common.Design.ProjectModel.ElementNode.AddChild(ElementNode node)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.MergeOrAddNode(ElementNode root, ElementNode node, Boolean compareBothSides)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.MergeNodesRecursively(ElementNode root, ElementNode node, ITargetElement target, Boolean compareBothSides)
          at ArtOfTest.Common.Design.ProjectModel.ElementsTree.Merge(ElementNode branch, Boolean compareBothSides)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.MergeElements(IEnumerable`1 descriptors)

[03/08 08:58:29,Telerik.TestStudio.Desktop.exe(2116:4),TestStudio] <>c__DisplayClasse.<LoadInternal>b__9() : Extract elements/build elements tree time: 00:00:06.8604186
[03/08 08:58:29,Telerik.TestStudio.Desktop.exe(2116:4),TestStudio] <>c__DisplayClasse.<LoadInternal>b__9() : Total Elements count: 2397
[03/08 08:59:58,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'WebTest' selected.
[03/08 08:59:58,Telerik.TestStudio.Desktop.exe(2116:25)] First trace message from background thread "ElementsProviderTaskDispatcher" (managed ID = 25, native ID = 7492).
[03/08 08:59:58,Telerik.TestStudio.Desktop.exe(2116:25),TestStudio] <>c__DisplayClass1d.<ReplaceExtractedElements>b__1c() : EXCEPTION! (see below)
     Outer Exception Type: System.NullReferenceException
     Message: Object reference not set to an instance of an object.
     HRESULT: 0x80004003 (Official ID (if app.) = E_POINTER, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16387)
     Call Stack:
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsInternal(TestBase test)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.<>c__DisplayClass1d.<ReplaceExtractedElements>b__1c()

[03/08 09:00:04,Telerik.TestStudio.Desktop.exe(2116:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'WebTest Copy' selected.
[03/08 09:00:04,Telerik.TestStudio.Desktop.exe(2116:25),TestStudio] <>c__DisplayClass1d.<ReplaceExtractedElements>b__1c() : EXCEPTION! (see below)
     Outer Exception Type: System.NullReferenceException
     Message: Object reference not set to an instance of an object.
     HRESULT: 0x80004003 (Official ID (if app.) = E_POINTER, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16387)
     Call Stack:
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsRecursive(ElementNode[] elementNodes, List`1 cachedTestDesc, ElementNode parentNode)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.ReplaceExtractedElementsInternal(TestBase test)
          at ArtOfTest.WebAii.Design.ProjectModel.Providers.ElementsProvider.<>c__DisplayClass1d.<ReplaceExtractedElements>b__1c()

